[INFO] cloning repository https://github.com/manoelhc/sos
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/manoelhc/sos" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fmanoelhc%2Fsos", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fmanoelhc%2Fsos'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] 4e54c204601f1475c3da74d3f8fa3a5c06ab3861
[INFO] testing manoelhc/sos against master#1f7f8ea0721a3b1eb73e6c6d25cccb371434b320 for pr-154065-1
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fmanoelhc%2Fsos" "/workspace/builds/worker-5-tc1/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-5-tc1/source'...
[INFO] [stderr] done.
[INFO] removed /workspace/builds/worker-5-tc1/source/.cargo/config.toml
[INFO] started tweaking git repo https://github.com/manoelhc/sos
[INFO] finished tweaking git repo https://github.com/manoelhc/sos
[INFO] tweaked toml for git repo https://github.com/manoelhc/sos written to /workspace/builds/worker-5-tc1/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/manoelhc/sos on toolchain 1f7f8ea0721a3b1eb73e6c6d25cccb371434b320
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1f7f8ea0721a3b1eb73e6c6d25cccb371434b320" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/manoelhc/sos already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1f7f8ea0721a3b1eb73e6c6d25cccb371434b320" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded defmt v0.3.100
[INFO] [stderr]   Downloaded managed v0.8.0
[INFO] [stderr]   Downloaded defmt-parser v1.0.0
[INFO] [stderr]   Downloaded defmt v1.0.1
[INFO] [stderr]   Downloaded defmt-macros v1.0.1
[INFO] [stderr]   Downloaded embedded-io v0.7.1
[INFO] [stderr]   Downloaded hash32 v0.1.1
[INFO] [stderr]   Downloaded embedded-io-async v0.7.0
[INFO] [stderr]   Downloaded generic-array v0.13.3
[INFO] [stderr]   Downloaded heapless v0.6.1
[INFO] [stderr]   Downloaded embedded-tls v0.18.0
[INFO] [stderr]   Downloaded as-slice v0.1.5
[INFO] [stderr]   Downloaded heapless v0.9.2
[INFO] [stderr]   Downloaded smoltcp v0.12.0
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+1f7f8ea0721a3b1eb73e6c6d25cccb371434b320" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 563619b5e7bdf48089dddfd3958bf09ec4b2ff939a2bb222db7af7193efa8747
[INFO] running `Command { std: "docker" "start" "-a" "563619b5e7bdf48089dddfd3958bf09ec4b2ff939a2bb222db7af7193efa8747", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "563619b5e7bdf48089dddfd3958bf09ec4b2ff939a2bb222db7af7193efa8747", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "563619b5e7bdf48089dddfd3958bf09ec4b2ff939a2bb222db7af7193efa8747", kill_on_drop: false }`
[INFO] [stdout] 563619b5e7bdf48089dddfd3958bf09ec4b2ff939a2bb222db7af7193efa8747
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+1f7f8ea0721a3b1eb73e6c6d25cccb371434b320" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] adf95a7850d2d76a49ff2d3c22b8aaee91177010bdb756258f1dcde312fd380d
[INFO] running `Command { std: "docker" "start" "-a" "adf95a7850d2d76a49ff2d3c22b8aaee91177010bdb756258f1dcde312fd380d", kill_on_drop: false }`
[INFO] [stderr]    Compiling byteorder v1.5.0
[INFO] [stderr]    Compiling heapless v0.8.0
[INFO] [stderr]    Compiling smoltcp v0.12.0
[INFO] [stderr]    Compiling bitflags v1.3.2
[INFO] [stderr]    Compiling managed v0.8.0
[INFO] [stderr]    Compiling spin v0.9.8
[INFO] [stderr]    Compiling hash32 v0.3.1
[INFO] [stderr]    Compiling sos v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 4.52s
[INFO] running `Command { std: "docker" "inspect" "adf95a7850d2d76a49ff2d3c22b8aaee91177010bdb756258f1dcde312fd380d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "adf95a7850d2d76a49ff2d3c22b8aaee91177010bdb756258f1dcde312fd380d", kill_on_drop: false }`
[INFO] [stdout] adf95a7850d2d76a49ff2d3c22b8aaee91177010bdb756258f1dcde312fd380d
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+1f7f8ea0721a3b1eb73e6c6d25cccb371434b320" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 76600748b2add3365683db508b4137a4629da50edfcb8795fbacb9518a03f7bf
[INFO] running `Command { std: "docker" "start" "-a" "76600748b2add3365683db508b4137a4629da50edfcb8795fbacb9518a03f7bf", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.183
[INFO] [stderr]    Compiling zeroize v1.8.2
[INFO] [stderr]    Compiling cc v1.2.57
[INFO] [stderr]    Compiling deranged v0.5.8
[INFO] [stderr]    Compiling rustls v0.23.37
[INFO] [stderr]    Compiling base64 v0.22.1
[INFO] [stderr]    Compiling once_cell v1.21.4
[INFO] [stderr]    Compiling rustls-pki-types v1.14.0
[INFO] [stderr]    Compiling pem v3.0.6
[INFO] [stderr]    Compiling ring v0.17.14
[INFO] [stderr]    Compiling getrandom v0.2.17
[INFO] [stderr]    Compiling time v0.3.47
[INFO] [stderr]    Compiling yasna v0.5.2
[INFO] [stderr]    Compiling rustls-webpki v0.103.9
[INFO] [stderr]    Compiling rcgen v0.13.2
[INFO] [stderr]    Compiling sos v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 19.38s
[INFO] running `Command { std: "docker" "inspect" "76600748b2add3365683db508b4137a4629da50edfcb8795fbacb9518a03f7bf", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "76600748b2add3365683db508b4137a4629da50edfcb8795fbacb9518a03f7bf", kill_on_drop: false }`
[INFO] [stdout] 76600748b2add3365683db508b4137a4629da50edfcb8795fbacb9518a03f7bf
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+1f7f8ea0721a3b1eb73e6c6d25cccb371434b320" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 2648d2107847b177955660232c18c3baf17449774d7ac3467f61356974d51682
[INFO] running `Command { std: "docker" "start" "-a" "2648d2107847b177955660232c18c3baf17449774d7ac3467f61356974d51682", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.12s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/sos-02bfc17a826a96cd)
[INFO] [stdout] 
[INFO] [stdout] running 62 tests
[INFO] [stdout] test allocator::buddy::tests::test_alloc_dealloc_reuse ... ok
[INFO] [stdout] test allocator::slab::tests::test_alloc_single ... ok
[INFO] [stdout] test allocator::slab::tests::test_alloc_reuses_deallocated ... ok
[INFO] [stdout] test allocator::slab::tests::test_dealloc ... ok
[INFO] [stdout] test allocator::slab::tests::test_alloc_multiple ... ok
[INFO] [stdout] test allocator::buddy::tests::test_new ... ok
[INFO] [stdout] test allocator::buddy::tests::test_alloc_multiple_blocks ... ok
[INFO] [stdout] test allocator::slab::tests::test_init ... ok
[INFO] [stdout] test allocator::slab::tests::test_new ... ok
[INFO] [stdout] test console::tests::boot_self_check_transcript_is_emitted ... ok
[INFO] [stdout] test console::tests::console_reader_sequence_works ... ok
[INFO] [stdout] test console::tests::console_help_programs_program_help_and_history ... ok
[INFO] [stdout] test console::tests::parse_command_line_rejects_empty ... ok
[INFO] [stdout] test console::tests::parse_command_line_rejects_too_many_args ... ok
[INFO] [stdout] test console::tests::console_service_runs_one_command ... ok
[INFO] [stdout] test console::tests::kernel_pf_apply_increments_generation_and_export_state ... ok
[INFO] [stdout] test console::tests::program_service_handles_execute_spawn_wait_terminate ... ok
[INFO] [stdout] test console::tests::prompt_budget_constant_is_enforced_by_contract ... ok
[INFO] [stdout] test console::tests::registry_dispatches_sos_pf_program ... ok
[INFO] [stdout] test console::tests::registry_rejects_unknown_program ... ok
[INFO] [stdout] test framekernel::ostd::tests::test_ostd_alloc ... ok
[INFO] [stdout] test console::tests::sos_pf_status_shows_exact_generation ... ok
[INFO] [stdout] test console::tests::parse_command_line_splits_program_and_args ... ok
[INFO] [stdout] test fs::sosfs::tests::test_fsck_one_invalid_mirror_one_valid ... ok
[INFO] [stdout] test fs::sosfs::tests::test_validate_superblock_bad_magic ... ok
[INFO] [stdout] test framekernel::ostd::tests::test_ostd_new ... ok
[INFO] [stdout] test fs::sosfs::tests::test_fsck_both_invalid_mirrors ... ok
[INFO] [stdout] test fs::sosfs::tests::test_fsck_valid_mirror_pair ... ok
[INFO] [stdout] test fs::sosfs::tests::test_validate_superblock_bad_checksum ... ok
[INFO] [stdout] test fs::sosfs::tests::test_fsck_generation_mismatch ... ok
[INFO] [stdout] test fs::sosfs::tests::test_validate_superblock_bad_block_size ... ok
[INFO] [stdout] test network::stack::tests::test_rtt_buffer_calibration ... ok
[INFO] [stdout] test fs::sosfs::tests::test_superblock_probe_accepts_valid_sosfs ... ok
[INFO] [stdout] test network::stack::tests::test_window_scale_calculation ... ok
[INFO] [stdout] test process::tests::process_lifecycle_transitions_work ... ok
[INFO] [stdout] test network::virtio::tests::test_dma_roundtrip_without_extra_copy ... ok
[INFO] [stdout] test process::tests::ipc_bus_routes_message_by_endpoint ... ok
[INFO] [stdout] test process::tests::ipc_queue_full_returns_error ... ok
[INFO] [stdout] test process::tests::parse_executable_header_rejects_invalid_magic ... ok
[INFO] [stdout] test process::tests::switching_without_context_fails ... ok
[INFO] [stdout] test sync::tests::test_atomic_slab_bitmap_find_free ... ok
[INFO] [stdout] test fs::sosfs::tests::test_validate_superblock_bad_flags ... ok
[INFO] [stdout] test sync::tests::test_atomic_slab_bitmap_set ... ok
[INFO] [stdout] test sync::tests::test_mutex_new ... ok
[INFO] [stdout] test process::tests::parse_executable_header_accepts_valid_image ... ok
[INFO] [stdout] test fs::sosfs::tests::test_validate_superblock_bad_version ... ok
[INFO] [stdout] test sync::tests::test_spinlock_lock_unlock ... ok
[INFO] [stdout] test sync::tests::test_atomic_slab_bitmap_new ... ok
[INFO] [stdout] test process::tests::spawned_processes_get_isolated_address_spaces ... ok
[INFO] [stdout] test sync::tests::test_mutex_mutable_access ... ok
[INFO] [stdout] test tests::test_slab_allocator ... ok
[INFO] [stdout] test sync::tests::test_spinlock_new ... ok
[INFO] [stdout] test tests::test_buddy_allocator ... ok
[INFO] [stdout] test tests::test_spinlock ... ok
[INFO] [stdout] test allocator::buddy::tests::test_alignment ... ok
[INFO] [stdout] test tests::test_mutex ... ok
[INFO] [stdout] test fs::sosfs::tests::test_superblock_probe_rejects_invalid_checksum ... ok
[INFO] [stdout] test fs::sosfs::tests::test_fsck_mirror_mismatch_uuid ... ok
[INFO] [stdout] test allocator::buddy::tests::test_alloc_single_block ... ok
[INFO] [stdout] test allocator::buddy::tests::test_oom ... ok
[INFO] [stdout] test process::tests::process_load_build_map_and_switch_context ... ok
[INFO] [stdout] test network::stack::tests::test_recommended_window_monotonic_with_rtt ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 62 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.06s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/bin/main.rs (/opt/rustwide/target/debug/deps/sos-e68b9f1bc3c6b54a)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests sos
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "2648d2107847b177955660232c18c3baf17449774d7ac3467f61356974d51682", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "2648d2107847b177955660232c18c3baf17449774d7ac3467f61356974d51682", kill_on_drop: false }`
[INFO] [stdout] 2648d2107847b177955660232c18c3baf17449774d7ac3467f61356974d51682
